04-24 15:48:43 D/ModuleListener: ModuleListener.testStarted(com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchPTT) on SKBFXYZO0DTQ
04-24 15:48:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 15:48:43 D/DeviceHostUtils: apiLevel: 31
04-24 15:48:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:44 D/RunUtil: Running command null without timeout.
04-24 15:48:44 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
04-24 15:48:44 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
04-24 15:48:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
04-24 15:48:44 I/AppRunnerUtils: Service PID = null
04-24 15:48:44 D/AppRunnerUtils: Cannot find service: com.google.android.tv.remote.service/.RemoteService
04-24 15:48:44 D/RunUtil: operation failed, waiting for 100 ms
04-24 15:48:44 D/RunUtil: Running command null without timeout.
04-24 15:48:44 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
04-24 15:48:44 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
04-24 15:48:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
04-24 15:48:44 I/AppRunnerUtils: Service PID = 30696
04-24 15:48:44 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 15:48:44 D/DeviceHostUtils: apiLevel: 31
04-24 15:48:44 I/TvtsRvisTest: KEYCODE_TO_VP_SHOWN	KEYCODE_TO_READING (In Milliseconds)
04-24 15:48:44 D/RunUtil: Running command null without timeout.
04-24 15:48:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:44 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:45 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:45 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:45 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:46 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:46 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:46 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:46 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:47 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:47 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:47 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:47 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:47 E/TvtsRvisTest: Run 1/10 encountered exception: Didn't successfully read non-zero audio bytes!
04-24 15:48:47 I/TvtsRvisTest:               -1000	             -1000
04-24 15:48:47 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:48 D/RunUtil: Running command null without timeout.
04-24 15:48:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:48 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:48 I/TvtsRvisTest:                  30	               155
04-24 15:48:48 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:49 D/RunUtil: Running command null without timeout.
04-24 15:48:49 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:49 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:50 I/TvtsRvisTest:                  20	                63
04-24 15:48:50 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:50 D/RunUtil: Running command null without timeout.
04-24 15:48:50 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:50 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:50 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:50 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:51 I/TvtsRvisTest:                  22	               240
04-24 15:48:51 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:51 D/RunUtil: Running command null without timeout.
04-24 15:48:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:51 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:52 I/TvtsRvisTest:                  19	                50
04-24 15:48:52 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:52 D/RunUtil: Running command null without timeout.
04-24 15:48:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:52 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:52 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:52 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:53 I/TvtsRvisTest:                  12	               362
04-24 15:48:53 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:53 D/RunUtil: Running command null without timeout.
04-24 15:48:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:53 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:53 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:54 I/TvtsRvisTest:                  15	               216
04-24 15:48:54 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:54 D/RunUtil: Running command null without timeout.
04-24 15:48:54 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:54 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:55 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:55 I/TvtsRvisTest:                  11	               192
04-24 15:48:55 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:55 D/RunUtil: Running command null without timeout.
04-24 15:48:55 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:56 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:56 I/TvtsRvisTest:                  10	                40
04-24 15:48:56 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:56 D/RunUtil: Running command null without timeout.
04-24 15:48:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:48:57 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:57 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:48:57 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:48:57 I/TvtsRvisTest:                  22	               296
04-24 15:48:57 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:48:58 I/TvtsRvisTest: numOfRuns: 10
04-24 15:48:58 I/TvtsRvisTest: numOfFailedRuns: 1
04-24 15:48:58 D/PrettyTestEventLogger: 
==================== com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchPTT ENDED: Wed Apr 24 15:48:58 KST 2024 ====================
